← Index
NYTProf Performance Profile   
For starman worker -M FindBin --max-requests 50 --workers 2 --user=kohadev-koha --group kohadev-koha --pid /var/run/koha/kohadev/plack.pid --daemonize --access-log /var/log/koha/kohadev/plack.log --error-log /var/log/koha/kohadev/plack-error.log -E deployment --socket /var/run/koha/kohadev/plack.sock /etc/koha/sites/kohadev/plack.psgi
  Run on Fri Jan 8 13:01:18 2016
Reported on Fri Jan 8 13:01:35 2016

Filename(eval 205)[/usr/share/perl5/Sub/Quote.pm:5]
StatementsExecuted 276 statements in 787µs
Eval Invoked At/usr/share/perl5/Sub/Quote.pm line 5
Sibling evals1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14
Subroutines
Calls P F Exclusive
Time
Inclusive
Time
Subroutine
13433916µs2.49msDBIx::Class::ResultSourceProxy::::column_infoDBIx::Class::ResultSourceProxy::column_info
11112µs20µsSub::Quote::::BEGIN@4.111 Sub::Quote::BEGIN@4.111
1116µs6µsDBIx::Class::ResultSourceProxy::::BEGIN@10.112DBIx::Class::ResultSourceProxy::BEGIN@10.112
Call graph for these subroutines as a Graphviz dot language file.
Line State
ments
Time
on line
Calls Time
in subs
Code
1{
222µs my $_UNQUOTED = ${$_[1]->{"\$_UNQUOTED"}};
31200ns my $_QUOTED = ${$_[1]->{"\$_QUOTED"}};
4284µs228µs
# spent 20µs (12+8) within Sub::Quote::BEGIN@4.111 which was called: # once (12µs+8µs) by Sub::Quote::_clean_eval at line 4
no warnings 'closure';
# spent 20µs making 1 call to Sub::Quote::BEGIN@4.111 # spent 8µs making 1 call to warnings::unimport
5
# spent 2.49ms (916µs+1.57) within DBIx::Class::ResultSourceProxy::column_info which was called 134 times, avg 19µs/call: # 122 times (837µs+1.45ms) by DBIx::Class::InflateColumn::inflate_column at line 90 of DBIx/Class/InflateColumn.pm, avg 19µs/call # 11 times (69µs+96µs) by DBIx::Class::Relationship::HasOne::_validate_has_one_condition at line 101 of DBIx/Class/Relationship/HasOne.pm, avg 15µs/call # once (10µs+21µs) by DBIx::Class::InflateColumn::inflate_column at line 56 of Sub/Defer.pm
sub DBIx::Class::ResultSourceProxy::column_info {
6 $_QUOTED if 0;
713311µs $_UNQUOTED if 0;
8# BEGIN quote_sub PRELUDE
9package DBIx::Class::ResultSourceProxy;
10
# spent 6µs within DBIx::Class::ResultSourceProxy::BEGIN@10.112 which was called: # once (6µs+0s) by Sub::Quote::_clean_eval at line 15
BEGIN {
111900ns $^H = "132834";
121300ns ${^WARNING_BITS} = "UUUUUUUUUUUUUUUUU";
1315µs %^H = (
14 );
15158µs16µs}
# spent 6µs making 1 call to DBIx::Class::ResultSourceProxy::BEGIN@10.112
16# END quote_sub PRELUDE
17 DBIx::Class::_ENV_::ASSERT_NO_INTERNAL_INDIRECT_CALLS and DBIx::Class::_Util::fail_on_internal_call;
18133621µs2661.26ms shift->result_source_instance->column_info (@_);
# spent 386µs making 133 calls to DBIx::Class::ResultSource::column_info, avg 3µs/call # spent 51µs making 8 calls to Koha::Schema::Result::Item::result_source_instance, avg 6µs/call # spent 34µs making 6 calls to Koha::Schema::Result::Aqorder::result_source_instance, avg 6µs/call # spent 30µs making 3 calls to Koha::Schema::Result::CreatorBatch::result_source_instance, avg 10µs/call # spent 26µs making 2 calls to Koha::Schema::Result::TagsIndex::result_source_instance, avg 13µs/call # spent 25µs making 4 calls to Koha::Schema::Result::Aqbasket::result_source_instance, avg 6µs/call # spent 24µs making 2 calls to Koha::Schema::Result::Branchrelation::result_source_instance, avg 12µs/call # spent 23µs making 4 calls to Koha::Schema::Result::HoldFillTarget::result_source_instance, avg 6µs/call # spent 22µs making 4 calls to Koha::Schema::Result::Reserve::result_source_instance, avg 6µs/call # spent 21µs making 3 calls to Koha::Schema::Result::OldReserve::result_source_instance, avg 7µs/call # spent 19µs making 2 calls to Koha::Schema::Result::Review::result_source_instance, avg 10µs/call # spent 19µs making 3 calls to Koha::Schema::Result::Branchtransfer::result_source_instance, avg 6µs/call # spent 18µs making 2 calls to Koha::Schema::Result::BranchItemRule::result_source_instance, avg 9µs/call # spent 18µs making 3 calls to Koha::Schema::Result::Virtualshelfcontent::result_source_instance, avg 6µs/call # spent 16µs making 3 calls to Koha::Schema::Result::Borrower::result_source_instance, avg 5µs/call # spent 16µs making 2 calls to Koha::Schema::Result::BranchBorrowerCircRule::result_source_instance, avg 8µs/call # spent 15µs making 1 call to Koha::Schema::Result::Aqcontract::result_source_instance # spent 13µs making 2 calls to Koha::Schema::Result::AqordersTransfer::result_source_instance, avg 7µs/call # spent 13µs making 2 calls to Koha::Schema::Result::AqorderUser::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::Aqbookseller::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::BorrowerMessagePreference::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::Issue::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::Serialitem::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::OldIssue::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::CategoriesBranch::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::Subscriptionroutinglist::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::Aqbasketuser::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::Aqinvoice::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::ItemsLastBorrower::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::Subscription::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::Virtualshelfshare::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::Accountline::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::CourseItem::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::BorrowerAttribute::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::OpacNews::result_source_instance, avg 6µs/call # spent 11µs making 2 calls to Koha::Schema::Result::BorrowerAttributeTypesBranch::result_source_instance, avg 6µs/call # spent 11µs making 2 calls to Koha::Schema::Result::TagAll::result_source_instance, avg 6µs/call # spent 11µs making 2 calls to Koha::Schema::Result::MessageQueue::result_source_instance, avg 6µs/call # spent 11µs making 2 calls to Koha::Schema::Result::TransportCost::result_source_instance, avg 6µs/call # spent 11µs making 2 calls to Koha::Schema::Result::Rating::result_source_instance, avg 5µs/call # spent 10µs making 1 call to Koha::Schema::Result::Accountoffset::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::DefaultBranchCircRule::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::Virtualshelve::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::Aqbudgetborrower::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::CourseInstructor::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::Category::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::AuthorisedValuesBranch::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::Suggestion::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::AqordersItem::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::Permission::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::Aqbasketgroup::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::AuthTagStructure::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::ItemsSearchField::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::DefaultBorrowerCircRule::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::AuthSubfieldStructure::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::BorrowerFile::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::OverduerulesTransportType::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::PatronListPatron::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::TagsApproval::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::UserPermission::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::Biblioitem::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::BorrowerMessageTransportPreference::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::Letter::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::Biblioimage::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::BorrowerDebarment::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::BorrowerSync::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::ClassSource::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::DefaultBranchItemRule::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::Discharge::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::MessageTransport::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::Aqcontact::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::Patronimage::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::PatronList::result_source_instance # spent 5µs making 1 call to Koha::Schema::Result::Itemtype::result_source_instance # spent 5µs making 1 call to Koha::Schema::Result::Branch::result_source_instance
19 }
2011µs $$_UNQUOTED = \&DBIx::Class::ResultSourceProxy::column_info
21}
2213µs1;
23
24;